Dec 01 01:15:52.270 INFO testing starrhorne/nes-rust against master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61 for beta-1.31-5
Dec 01 01:15:52.270 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-6/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 01 01:15:52.500 INFO [stdout] 37ce7dea75f091800693e1e6a1f23590a31566e9d6995ec59e1876ed192f9a26
Dec 01 01:15:52.502 INFO running `"docker" "start" "-a" "37ce7dea75f091800693e1e6a1f23590a31566e9d6995ec59e1876ed192f9a26"`
Dec 01 01:15:52.924 INFO [stderr] usermod: no changes
Dec 01 01:15:52.973 INFO [stderr]    Compiling libc v0.2.24
Dec 01 01:15:52.974 INFO [stderr]    Compiling either v1.1.0
Dec 01 01:15:52.974 INFO [stderr]    Compiling bitfield v0.12.0
Dec 01 01:15:53.256 INFO [stderr]    Compiling itertools v0.6.1
Dec 01 01:15:54.027 INFO [stderr]    Compiling libretro-sys v0.1.0
Dec 01 01:15:54.027 INFO [stderr]    Compiling rand v0.3.17
Dec 01 01:15:54.028 INFO [stderr]    Compiling time v0.1.39
Dec 01 01:15:57.571 INFO [stderr]    Compiling libretro-backend v0.2.1
Dec 01 01:15:59.699 INFO [stderr]    Compiling nes v0.1.0 (/source)
Dec 01 01:16:02.711 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 9.76s
Dec 01 01:16:02.715 INFO [stderr] su: No module specific data is present
Dec 01 01:16:03.535 INFO running `"docker" "inspect" "37ce7dea75f091800693e1e6a1f23590a31566e9d6995ec59e1876ed192f9a26"`
Dec 01 01:16:03.757 INFO running `"docker" "rm" "-f" "37ce7dea75f091800693e1e6a1f23590a31566e9d6995ec59e1876ed192f9a26"`
Dec 01 01:16:04.023 INFO [stdout] 37ce7dea75f091800693e1e6a1f23590a31566e9d6995ec59e1876ed192f9a26
Dec 01 01:16:04.030 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-6/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 01 01:16:04.634 INFO [stdout] f7f61f08e4a0544076dcb1d6a3bd13960ef32d45a1d91c46e51c1188ad1715c3
Dec 01 01:16:04.637 INFO running `"docker" "start" "-a" "f7f61f08e4a0544076dcb1d6a3bd13960ef32d45a1d91c46e51c1188ad1715c3"`
Dec 01 01:16:05.847 INFO [stderr] usermod: no changes
Dec 01 01:16:05.927 INFO [stderr]    Compiling nes v0.1.0 (/source)
Dec 01 01:16:07.405 INFO [stderr] warning: unused import: `apu::Apu`
Dec 01 01:16:07.405 INFO [stderr]  --> src/./cpu_test.rs:3:5
Dec 01 01:16:07.405 INFO [stderr]   |
Dec 01 01:16:07.405 INFO [stderr] 3 | use apu::Apu;
Dec 01 01:16:07.405 INFO [stderr]   |     ^^^^^^^^
Dec 01 01:16:07.405 INFO [stderr]   |
Dec 01 01:16:07.405 INFO [stderr]   = note: #[warn(unused_imports)] on by default
Dec 01 01:16:07.405 INFO [stderr] 
Dec 01 01:16:07.405 INFO [stderr] warning: unused import: `ppu::Ppu`
Dec 01 01:16:07.405 INFO [stderr]  --> src/./cpu_test.rs:6:5
Dec 01 01:16:07.405 INFO [stderr]   |
Dec 01 01:16:07.405 INFO [stderr] 6 | use ppu::Ppu;
Dec 01 01:16:07.405 INFO [stderr]   |     ^^^^^^^^
Dec 01 01:16:07.405 INFO [stderr] 
Dec 01 01:16:07.405 INFO [stderr] warning: unused macro definition
Dec 01 01:16:07.405 INFO [stderr]   --> src/./cpu_test.rs:44:1
Dec 01 01:16:07.405 INFO [stderr]    |
Dec 01 01:16:07.405 INFO [stderr] 44 | / macro_rules! build_cpu_and_run {
Dec 01 01:16:07.405 INFO [stderr] 45 | |     ($instruction:expr, $mode:ident, $bytes:expr) => {
Dec 01 01:16:07.405 INFO [stderr] 46 | |         {
Dec 01 01:16:07.405 INFO [stderr] 47 | |             let op = opcode($instruction, $mode);
Dec 01 01:16:07.405 INFO [stderr] ...  |
Dec 01 01:16:07.405 INFO [stderr] 60 | |     }
Dec 01 01:16:07.405 INFO [stderr] 61 | | }
Dec 01 01:16:07.405 INFO [stderr]    | |_^
Dec 01 01:16:07.405 INFO [stderr]    |
Dec 01 01:16:07.405 INFO [stderr]    = note: #[warn(unused_macros)] on by default
Dec 01 01:16:07.405 INFO [stderr] 
Dec 01 01:16:07.458 INFO [stderr] warning: unused import: `super::*`
Dec 01 01:16:07.458 INFO [stderr]    --> src/bus.rs:203:9
Dec 01 01:16:07.458 INFO [stderr]     |
Dec 01 01:16:07.458 INFO [stderr] 203 |     use super::*;
Dec 01 01:16:07.458 INFO [stderr]     |         ^^^^^^^^
Dec 01 01:16:07.458 INFO [stderr] 
Dec 01 01:16:21.708 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 15.84s
Dec 01 01:16:21.716 INFO [stderr] su: No module specific data is present
Dec 01 01:16:22.667 INFO running `"docker" "inspect" "f7f61f08e4a0544076dcb1d6a3bd13960ef32d45a1d91c46e51c1188ad1715c3"`
Dec 01 01:16:22.894 INFO running `"docker" "rm" "-f" "f7f61f08e4a0544076dcb1d6a3bd13960ef32d45a1d91c46e51c1188ad1715c3"`
Dec 01 01:16:23.178 INFO [stdout] f7f61f08e4a0544076dcb1d6a3bd13960ef32d45a1d91c46e51c1188ad1715c3
Dec 01 01:16:23.182 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-6/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 01 01:16:23.636 INFO [stdout] 862737ccd01ab8c6e2e3308c97c2d5d2fc84c3f7469cb4220a999e576ca310a6
Dec 01 01:16:23.648 INFO running `"docker" "start" "-a" "862737ccd01ab8c6e2e3308c97c2d5d2fc84c3f7469cb4220a999e576ca310a6"`
Dec 01 01:16:24.611 INFO [stderr] usermod: no changes
Dec 01 01:16:24.674 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.03s
Dec 01 01:16:24.687 INFO [stderr]      Running /target/debug/deps/nes-aa4e33b15bc95e31
Dec 01 01:16:24.687 INFO [stdout] 
Dec 01 01:16:24.687 INFO [stdout] running 119 tests
Dec 01 01:16:24.748 INFO [stdout] test cartridge::cartridge_header::test::test_sizes ... ok
Dec 01 01:16:24.798 INFO [stdout] test cartridge::mapper1::test::test_shift ... ok
Dec 01 01:16:24.879 INFO [stdout] test cartridge::pager::test::test_index_first ... ok
Dec 01 01:16:24.895 INFO [stdout] test cartridge::pager::test::test_index_last ... ok
Dec 01 01:16:24.981 INFO [stdout] test cartridge::mapper1::test::test_set_control ... ok
Dec 01 01:16:24.981 INFO [stdout] test cartridge::mapper1::test::test_chr_rom ... ok
Dec 01 01:16:25.005 INFO [stdout] test cartridge::pager::test::test_index_number ... ok
Dec 01 01:16:25.011 INFO [stdout] test cartridge::pager::test::test_index_overflow ... ok
Dec 01 01:16:25.011 INFO [stdout] test cartridge::pager::test::test_index_nopage ... ok
Dec 01 01:16:25.039 INFO [stdout] test cartridge::pager::test::test_rw ... ok
Dec 01 01:16:25.039 INFO [stdout] test cartridge::ppu_test::test_chr_ram ... ok
Dec 01 01:16:25.039 INFO [stdout] test cartridge::pager::test::test_page_count ... ok
Dec 01 01:16:25.067 INFO [stdout] test cartridge::ppu_test::test_read_chr_rom ... ok
Dec 01 01:16:25.079 INFO [stdout] test cartridge::ppu_test::test_read_prg_rom ... ok
Dec 01 01:16:25.079 INFO [stdout] test cpu::cpu_test::test_adc ... ok
Dec 01 01:16:25.079 INFO [stdout] test cartridge::mapper1::test::test_set_chr_0 ... ok
Dec 01 01:16:25.079 INFO [stdout] test cartridge::ppu_test::test_prg_ram ... ok
Dec 01 01:16:25.087 INFO [stdout] test cpu::cpu_test::test_bcs ... ok
Dec 01 01:16:25.087 INFO [stdout] test cartridge::mapper1::test::test_prg_rom ... ok
Dec 01 01:16:25.087 INFO [stdout] test cpu::cpu_test::test_bit ... ok
Dec 01 01:16:25.103 INFO [stdout] test cpu::cpu_test::test_bcc ... ok
Dec 01 01:16:25.103 INFO [stdout] test cpu::cpu_test::test_beq ... ok
Dec 01 01:16:25.115 INFO [stdout] test cpu::cpu_test::test_bmi ... ok
Dec 01 01:16:25.115 INFO [stdout] test cpu::cpu_test::test_asl ... ok
Dec 01 01:16:25.115 INFO [stdout] test cpu::cpu_test::test_and ... ok
Dec 01 01:16:25.115 INFO [stdout] test cpu::cpu_test::test_brk ... ok
Dec 01 01:16:25.120 INFO [stdout] test cpu::cpu_test::test_cmp ... ok
Dec 01 01:16:25.121 INFO [stdout] test cpu::cpu_test::test_bpl ... ok
Dec 01 01:16:25.124 INFO [stdout] test cpu::cpu_test::test_cpx ... ok
Dec 01 01:16:25.124 INFO [stdout] test cpu::cpu_test::test_bvc ... ok
Dec 01 01:16:25.127 INFO [stdout] test cpu::cpu_test::test_bne ... ok
Dec 01 01:16:25.127 INFO [stdout] test cpu::cpu_test::test_bvs ... ok
Dec 01 01:16:25.144 INFO [stdout] test cpu::cpu_test::test_dec ... ok
Dec 01 01:16:25.147 INFO [stdout] test cpu::cpu_test::test_dex ... ok
Dec 01 01:16:25.149 INFO [stdout] test cpu::cpu_test::test_cpy ... ok
Dec 01 01:16:25.195 INFO [stdout] test cpu::cpu_test::test_flag_ops ... ok
Dec 01 01:16:25.195 INFO [stdout] test cpu::cpu_test::test_eor ... ok
Dec 01 01:16:25.198 INFO [stdout] test cpu::cpu_test::test_iny ... ok
Dec 01 01:16:25.198 INFO [stdout] test cpu::cpu_test::test_inx ... ok
Dec 01 01:16:25.198 INFO [stdout] test cpu::cpu_test::test_lda ... ok
Dec 01 01:16:25.199 INFO [stdout] test cpu::cpu_test::test_dey ... ok
Dec 01 01:16:25.199 INFO [stdout] test cpu::cpu_test::test_inc ... ok
Dec 01 01:16:25.199 INFO [stdout] test cpu::cpu_test::test_jmp ... ok
Dec 01 01:16:25.199 INFO [stdout] test cartridge::mapper1::test::test_prg_ram ... ok
Dec 01 01:16:25.207 INFO [stdout] test cpu::cpu_test::test_lsr ... ok
Dec 01 01:16:25.207 INFO [stdout] test cpu::cpu_test::test_ldx ... ok
Dec 01 01:16:25.208 INFO [stdout] test cpu::cpu_test::test_jsr_ret ... ok
Dec 01 01:16:25.208 INFO [stdout] test cpu::cpu_test::test_ldy ... ok
Dec 01 01:16:25.211 INFO [stdout] test cpu::cpu_test::test_ora ... ok
Dec 01 01:16:25.219 INFO [stdout] test cpu::cpu_test::test_ror ... ok
Dec 01 01:16:25.219 INFO [stdout] test cpu::cpu_test::test_pha_pla ... ok
Dec 01 01:16:25.227 INFO [stdout] test cartridge::mapper1::test::test_set_chr_1 ... ok
Dec 01 01:16:25.227 INFO [stdout] test cpu::cpu_test::test_rol ... ok
Dec 01 01:16:25.227 INFO [stdout] test cpu::cpu_test::test_tay ... ok
Dec 01 01:16:25.240 INFO [stdout] test cpu::cpu_test::test_php_plp ... ok
Dec 01 01:16:25.241 INFO [stdout] test cpu::cpu_test::test_sta ... ok
Dec 01 01:16:25.241 INFO [stdout] test cpu::cpu_test::test_sbc ... ok
Dec 01 01:16:25.241 INFO [stdout] test cpu::cpu_test::test_tax ... ok
Dec 01 01:16:25.241 INFO [stdout] test cpu::cpu_test::test_stx ... ok
Dec 01 01:16:25.242 INFO [stdout] test cpu::cpu_test::test_sty ... ok
Dec 01 01:16:25.242 INFO [stdout] test cartridge::mapper1::test::test_set_prg ... ok
Dec 01 01:16:25.242 INFO [stdout] test cpu::cpu_test::test_tsx ... ok
Dec 01 01:16:25.243 INFO [stdout] test cpu::cpu_test::test_txs ... ok
Dec 01 01:16:25.243 INFO [stdout] test cpu::cpu_test::test_tya ... ok
Dec 01 01:16:25.243 INFO [stdout] test ppu::address::test::test_attribute_address ... ok
Dec 01 01:16:25.243 INFO [stdout] test ppu::address::test::test_loopy ... ok
Dec 01 01:16:25.244 INFO [stdout] test ppu::address::test::test_nametable_address ... ok
Dec 01 01:16:25.244 INFO [stdout] test ppu::address::test::test_scroll_y ... ok
Dec 01 01:16:25.244 INFO [stdout] test ppu::address::test::test_scroll_x ... ok
Dec 01 01:16:25.244 INFO [stdout] test ppu::address::test::test_register_address ... ok
Dec 01 01:16:25.245 INFO [stdout] test ppu::control::test::test_background_tile_base ... ok
Dec 01 01:16:25.245 INFO [stdout] test ppu::address::test::test_tile_offset ... ok
Dec 01 01:16:25.245 INFO [stdout] test ppu::control::test::test_increment_amount ... ok
Dec 01 01:16:25.245 INFO [stdout] test ppu::control::test::test_sprite_height ... ok
Dec 01 01:16:25.246 INFO [stdout] test ppu::control::test::test_sprite_tile_base ... ok
Dec 01 01:16:25.246 INFO [stdout] test ppu::mask::test::test_rendering_background ... ok
Dec 01 01:16:25.246 INFO [stdout] test ppu::mask::test::test_rendering_sprites ... ok
Dec 01 01:16:25.247 INFO [stdout] test ppu::registers::test::test_read_data_delayed ... ok
Dec 01 01:16:25.247 INFO [stdout] test ppu::registers::test::test_read_ghost_bits ... ok
Dec 01 01:16:25.247 INFO [stdout] test ppu::registers::test::test_read_oam_data ... ok
Dec 01 01:16:25.247 INFO [stdout] test ppu::registers::test::test_read_status ... ok
Dec 01 01:16:25.248 INFO [stdout] test ppu::registers::test::test_write_address ... ok
Dec 01 01:16:25.248 INFO [stdout] test ppu::registers::test::test_write_control ... ok
Dec 01 01:16:25.248 INFO [stdout] test ppu::registers::test::test_write_data ... ok
Dec 01 01:16:25.248 INFO [stdout] test ppu::registers::test::test_write_mask ... ok
Dec 01 01:16:25.249 INFO [stdout] test ppu::registers::test::test_write_oam_address ... ok
Dec 01 01:16:25.249 INFO [stdout] test ppu::registers::test::test_write_oam_data ... ok
Dec 01 01:16:25.249 INFO [stdout] test ppu::registers::test::test_write_scroll ... ok
Dec 01 01:16:25.249 INFO [stdout] test ppu::renderer::test::test_evaluate_sprites ... ok
Dec 01 01:16:25.250 INFO [stdout] test ppu::renderer::test::test_load_sprites ... ok
Dec 01 01:16:25.250 INFO [stdout] test ppu::renderer::test::test_reload_shift ... ok
Dec 01 01:16:25.250 INFO [stdout] test ppu::renderer::test::test_render_background_pixel ... ok
Dec 01 01:16:25.250 INFO [stdout] test ppu::renderer::test::test_render_pixel_opaque_sprite_behind ... ok
Dec 01 01:16:25.251 INFO [stdout] test ppu::renderer::test::test_render_pixel_opaque_sprite_front ... ok
Dec 01 01:16:25.251 INFO [stdout] test ppu::renderer::test::test_render_pixel_transparent_sprite_front ... ok
Dec 01 01:16:25.251 INFO [stdout] test ppu::renderer::test::test_render_sprite_pixel ... ok
Dec 01 01:16:25.252 INFO [stdout] test ppu::renderer::test::test_shift ... ok
Dec 01 01:16:25.252 INFO [stdout] test ppu::renderer::test::test_sprite_overflow ... ok
Dec 01 01:16:25.252 INFO [stdout] test ppu::renderer::test::test_step ... ok
Dec 01 01:16:25.252 INFO [stdout] test ppu::sprite::test::test_color_index ... ok
Dec 01 01:16:25.253 INFO [stdout] test ppu::sprite::test::test_color_index_flip_x ... ok
Dec 01 01:16:25.253 INFO [stdout] test ppu::sprite::test::test_sprite_tile_offset ... ok
Dec 01 01:16:25.253 INFO [stdout] test ppu::address::test::test_copy_x ... ok
Dec 01 01:16:25.253 INFO [stdout] test ppu::address::test::test_copy_y ... ok
Dec 01 01:16:25.254 INFO [stdout] test ppu::sprite::test::test_tile_address_large_flip_y ... ok
Dec 01 01:16:25.254 INFO [stdout] test ppu::sprite::test::test_tile_address_large_no_flip ... ok
Dec 01 01:16:25.254 INFO [stdout] test cpu::cpu_test::test_txa ... ok
Dec 01 01:16:25.255 INFO [stdout] test ppu::sprite::test::test_tile_address_small_flip_y ... ok
Dec 01 01:16:25.255 INFO [stdout] test ppu::sprite::test::test_tile_address_small_no_flip ... ok
Dec 01 01:16:25.255 INFO [stdout] test ppu::vram::test::test_buffered_read_byte ... ok
Dec 01 01:16:25.255 INFO [stdout] test ppu::vram::test::test_mirror_nametable_horizontally ... ok
Dec 01 01:16:25.255 INFO [stdout] test ppu::sprite::test::test_tile_base ... ok
Dec 01 01:16:25.256 INFO [stdout] test ppu::vram::test::test_write_byte_nametable ... ok
Dec 01 01:16:25.256 INFO [stdout] test ppu::vram::test::test_write_byte_palette ... ok
Dec 01 01:16:25.256 INFO [stdout] test ppu::vram::test::test_read_byte_palette ... ok
Dec 01 01:16:25.257 INFO [stdout] test ppu::vram::test::test_mirror_palette ... ok
Dec 01 01:16:25.257 INFO [stdout] test ppu::vram::test::test_read_byte_cartridge ... ok
Dec 01 01:16:25.257 INFO [stdout] test ppu::vram::test::test_mirror_nametable_vertically ... ok
Dec 01 01:16:25.257 INFO [stdout] test ppu::vram::test::test_read_byte_nametable ... ok
Dec 01 01:16:25.258 INFO [stdout] 
Dec 01 01:16:25.258 INFO [stdout] test result: ok. 119 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 01 01:16:25.258 INFO [stdout] 
Dec 01 01:16:25.265 INFO [stderr] su: No module specific data is present
Dec 01 01:16:25.787 INFO running `"docker" "inspect" "862737ccd01ab8c6e2e3308c97c2d5d2fc84c3f7469cb4220a999e576ca310a6"`
Dec 01 01:16:26.030 INFO running `"docker" "rm" "-f" "862737ccd01ab8c6e2e3308c97c2d5d2fc84c3f7469cb4220a999e576ca310a6"`
Dec 01 01:16:26.315 INFO [stdout] 862737ccd01ab8c6e2e3308c97c2d5d2fc84c3f7469cb4220a999e576ca310a6
